Dec 02 13:33:07.121 INFO testing tokio-timer-0.2.7 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 02 13:33:07.121 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 13:33:07.316 INFO [stdout] db4321d08140592cc17994a1c5e58667fec5acf87c43da36cd9fa118878f9ff3
Dec 02 13:33:07.317 INFO running `"docker" "start" "-a" "db4321d08140592cc17994a1c5e58667fec5acf87c43da36cd9fa118878f9ff3"`
Dec 02 13:33:07.882 INFO [stderr] usermod: no changes
Dec 02 13:33:07.943 INFO [stderr]    Compiling tokio-timer v0.2.7 (/source)
Dec 02 13:33:09.046 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.15s
Dec 02 13:33:09.051 INFO [stderr] su: No module specific data is present
Dec 02 13:33:09.284 INFO running `"docker" "inspect" "db4321d08140592cc17994a1c5e58667fec5acf87c43da36cd9fa118878f9ff3"`
Dec 02 13:33:09.346 INFO running `"docker" "rm" "-f" "db4321d08140592cc17994a1c5e58667fec5acf87c43da36cd9fa118878f9ff3"`
Dec 02 13:33:09.407 INFO [stdout] db4321d08140592cc17994a1c5e58667fec5acf87c43da36cd9fa118878f9ff3
Dec 02 13:33:09.410 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 13:33:09.579 INFO [stdout] 6cf715917d6375135fd2fef5af1a14b86ceb12d41722c961b89845a33ff891de
Dec 02 13:33:09.581 INFO running `"docker" "start" "-a" "6cf715917d6375135fd2fef5af1a14b86ceb12d41722c961b89845a33ff891de"`
Dec 02 13:33:09.884 INFO [stderr] usermod: no changes
Dec 02 13:33:09.983 INFO [stderr]    Compiling tokio-mock-task v0.1.0
Dec 02 13:33:10.398 INFO [stderr]    Compiling tokio-timer v0.2.7 (/source)
Dec 02 13:33:18.322 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.42s
Dec 02 13:33:18.336 INFO [stderr] su: No module specific data is present
Dec 02 13:33:18.879 INFO running `"docker" "inspect" "6cf715917d6375135fd2fef5af1a14b86ceb12d41722c961b89845a33ff891de"`
Dec 02 13:33:19.219 INFO running `"docker" "rm" "-f" "6cf715917d6375135fd2fef5af1a14b86ceb12d41722c961b89845a33ff891de"`
Dec 02 13:33:19.579 INFO [stdout] 6cf715917d6375135fd2fef5af1a14b86ceb12d41722c961b89845a33ff891de
Dec 02 13:33:19.583 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-3/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 13:33:20.144 INFO [stdout] a106f870721ff694445a553390977029d7e5fbbc0817837d1c2312eeaaf71d99
Dec 02 13:33:20.147 INFO running `"docker" "start" "-a" "a106f870721ff694445a553390977029d7e5fbbc0817837d1c2312eeaaf71d99"`
Dec 02 13:33:21.410 INFO [stderr] usermod: no changes
Dec 02 13:33:21.605 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.17s
Dec 02 13:33:21.636 INFO [stderr]      Running /target/debug/deps/tokio_timer-88ca21767555e225
Dec 02 13:33:21.641 INFO [stdout] 
Dec 02 13:33:21.641 INFO [stdout] running 1 test
Dec 02 13:33:21.656 INFO [stdout] test wheel::test::test_level_for ... ok
Dec 02 13:33:21.657 INFO [stdout] 
Dec 02 13:33:21.657 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:21.657 INFO [stdout] 
Dec 02 13:33:21.663 INFO [stderr]      Running /target/debug/deps/clock-b11c778de01f026a
Dec 02 13:33:21.664 INFO [stdout] 
Dec 02 13:33:21.664 INFO [stdout] running 3 tests
Dec 02 13:33:21.665 INFO [stdout] test custom_clock ... ok
Dec 02 13:33:21.675 INFO [stdout] test execution_context ... ok
Dec 02 13:33:21.675 INFO [stdout] test default_clock ... ok
Dec 02 13:33:21.675 INFO [stdout] 
Dec 02 13:33:21.675 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:21.675 INFO [stdout] 
Dec 02 13:33:21.675 INFO [stderr]      Running /target/debug/deps/deadline-39bc024b304bd5aa
Dec 02 13:33:21.681 INFO [stdout] 
Dec 02 13:33:21.681 INFO [stdout] running 6 tests
Dec 02 13:33:21.687 INFO [stdout] test future_and_deadline_in_future ... ok
Dec 02 13:33:21.687 INFO [stdout] test deadline_now_elapses ... ok
Dec 02 13:33:21.693 INFO [stdout] test future_errors_first ... ok
Dec 02 13:33:21.693 INFO [stdout] test simultaneous_deadline_future_completion ... ok
Dec 02 13:33:21.693 INFO [stdout] test completed_future_past_deadline ... ok
Dec 02 13:33:21.693 INFO [stdout] test deadline_future_elapses ... ok
Dec 02 13:33:21.693 INFO [stdout] 
Dec 02 13:33:21.694 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:21.694 INFO [stdout] 
Dec 02 13:33:21.694 INFO [stderr]      Running /target/debug/deps/delay-db47b751a0899bdb
Dec 02 13:33:21.704 INFO [stdout] 
Dec 02 13:33:21.704 INFO [stdout] running 20 tests
Dec 02 13:33:21.705 INFO [stdout] test delayed_delay_level_0 ... ok
Dec 02 13:33:21.705 INFO [stdout] test delay_with_deadline_in_past ... ok
Dec 02 13:33:21.706 INFO [stdout] test immediate_delay ... ok
Dec 02 13:33:21.706 INFO [stdout] test reset_future_delay_after_fire ... ok
Dec 02 13:33:21.706 INFO [stdout] test delayed_delay_wrapping_level_0 ... ok
Dec 02 13:33:21.711 INFO [stdout] test delayed_delay_level_1 ... ok
Dec 02 13:33:21.711 INFO [stdout] test creating_delay_outside_of_context ... ok
Dec 02 13:33:21.714 INFO [stdout] test delay_with_default_handle ... ok
Dec 02 13:33:21.715 INFO [stdout] test reset_future_delay_before_fire ... ok
Dec 02 13:33:21.715 INFO [stdout] test greater_than_max ... ok
Dec 02 13:33:21.715 INFO [stdout] test reset_past_delay_before_turn ... ok
Dec 02 13:33:21.715 INFO [stdout] test reset_past_delay_before_fire ... ok
Dec 02 13:33:21.715 INFO [stdout] test set_timeout_at_deadline_greater_than_max_timer ... ok
Dec 02 13:33:21.715 INFO [stdout] test sorta_long_delay ... ok
Dec 02 13:33:21.718 INFO [stdout] test timer_wrapping_with_higher_levels ... ok
Dec 02 13:33:21.727 INFO [stdout] test short_delay ... ok
Dec 02 13:33:21.727 INFO [stdout] test concurrently_set_two_timers_second_one_shorter ... ok
Dec 02 13:33:21.727 INFO [stdout] test unpark_is_delayed ... ok
Dec 02 13:33:21.727 INFO [stdout] test sub_ms_delayed_delay ... ok
Dec 02 13:33:21.727 INFO [stdout] test very_long_delay ... ok
Dec 02 13:33:21.727 INFO [stdout] 
Dec 02 13:33:21.727 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:21.727 INFO [stdout] 
Dec 02 13:33:21.731 INFO [stderr]      Running /target/debug/deps/hammer-4acef6f05d980ed4
Dec 02 13:33:21.736 INFO [stdout] 
Dec 02 13:33:21.736 INFO [stdout] running 3 tests
Dec 02 13:33:25.076 INFO [stdout] test hammer_reset ... ok
Dec 02 13:33:45.390 INFO [stdout] test hammer_cancel ... ok
Dec 02 13:33:46.566 INFO [stdout] test hammer_complete ... ok
Dec 02 13:33:46.566 INFO [stdout] 
Dec 02 13:33:46.566 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:46.566 INFO [stdout] 
Dec 02 13:33:46.569 INFO [stderr]      Running /target/debug/deps/interval-f7a6b1abebdce1f1
Dec 02 13:33:46.571 INFO [stdout] 
Dec 02 13:33:46.571 INFO [stdout] running 2 tests
Dec 02 13:33:46.583 INFO [stdout] test usage ... ok
Dec 02 13:33:46.609 INFO [stdout] test interval_zero_duration ... ok
Dec 02 13:33:46.609 INFO [stdout] 
Dec 02 13:33:46.609 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:46.609 INFO [stdout] 
Dec 02 13:33:46.610 INFO [stderr]      Running /target/debug/deps/queue-0a4aaaabe6fab20b
Dec 02 13:33:46.612 INFO [stdout] 
Dec 02 13:33:46.612 INFO [stdout] running 8 tests
Dec 02 13:33:46.631 INFO [stdout] test remove_entry ... ok
Dec 02 13:33:46.631 INFO [stdout] test multi_immediate_delays ... ok
Dec 02 13:33:46.635 INFO [stdout] test single_immediate_delay ... ok
Dec 02 13:33:46.636 INFO [stdout] test multi_delay_at_start ... ok
Dec 02 13:33:46.643 INFO [stdout] test remove_expired_item ... ok
Dec 02 13:33:46.643 INFO [stdout] test insert_in_past_fires_immediately ... ok
Dec 02 13:33:46.643 INFO [stdout] test reset_entry ... ok
Dec 02 13:33:46.644 INFO [stdout] test single_short_delay ... ok
Dec 02 13:33:46.644 INFO [stdout] 
Dec 02 13:33:46.644 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:46.644 INFO [stdout] 
Dec 02 13:33:46.645 INFO [stderr]      Running /target/debug/deps/timeout-8e3c5f8013763a5d
Dec 02 13:33:46.648 INFO [stdout] 
Dec 02 13:33:46.648 INFO [stdout] running 9 tests
Dec 02 13:33:46.655 INFO [stdout] test deadline_now_elapses ... ok
Dec 02 13:33:46.676 INFO [stdout] test future_and_timeout_in_future ... ok
Dec 02 13:33:46.676 INFO [stdout] test future_and_deadline_in_future ... ok
Dec 02 13:33:46.683 INFO [stdout] test deadline_future_elapses ... ok
Dec 02 13:33:46.683 INFO [stdout] test completed_future_past_deadline ... ok
Dec 02 13:33:46.691 INFO [stdout] test future_errors_first ... ok
Dec 02 13:33:46.691 INFO [stdout] test idle_stream_timesout_periodically ... ok
Dec 02 13:33:46.695 INFO [stdout] test simultaneous_deadline_future_completion ... ok
Dec 02 13:33:46.695 INFO [stdout] test stream_and_timeout_in_future ... ok
Dec 02 13:33:46.695 INFO [stdout] 
Dec 02 13:33:46.695 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:33:46.695 INFO [stdout] 
Dec 02 13:33:46.695 INFO [stderr]    Doc-tests tokio-timer
Dec 02 13:33:47.735 INFO [stdout] 
Dec 02 13:33:47.735 INFO [stdout] running 16 tests
Dec 02 13:33:53.357 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::capacity (line 589) ... ok
Dec 02 13:33:53.977 INFO [stdout] test src/clock/clock.rs - clock::clock::now (line 36) ... ok
Dec 02 13:33:55.071 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::is_empty (line 636) ... ok
Dec 02 13:33:56.507 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::new (line 215) ... ok
Dec 02 13:34:00.287 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::reserve (line 617) ... ok
Dec 02 13:34:08.543 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::with_capacity (line 256) ... ok
Dec 02 13:34:16.495 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::with_capacity_and_handle (line 229) ... ok
Dec 02 13:34:16.651 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue (line 70) ... ok
Dec 02 13:34:19.471 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::insert (line 369) ... ok
Dec 02 13:34:20.695 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::clear (line 561) ... ok
Dec 02 13:34:21.139 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::insert_at (line 300) ... ok
Dec 02 13:34:25.551 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::reset (line 531) ... ok
Dec 02 13:34:26.923 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::reset_at (line 475) ... ok
Dec 02 13:34:27.851 INFO [stdout] test src/delay_queue.rs - delay_queue::DelayQueue<T>::remove (line 424) ... ok
Dec 02 13:34:35.444 INFO [stdout] test src/timeout.rs - timeout::Timeout (line 33) ... ok
Dec 02 13:34:35.456 INFO [stdout] test src/timeout.rs - timeout::Timeout<T>::new (line 108) ... ok
Dec 02 13:34:35.456 INFO [stdout] 
Dec 02 13:34:35.456 INFO [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 13:34:35.456 INFO [stdout] 
Dec 02 13:34:35.475 INFO [stderr] su: No module specific data is present
Dec 02 13:34:36.251 INFO running `"docker" "inspect" "a106f870721ff694445a553390977029d7e5fbbc0817837d1c2312eeaaf71d99"`
Dec 02 13:34:36.547 INFO running `"docker" "rm" "-f" "a106f870721ff694445a553390977029d7e5fbbc0817837d1c2312eeaaf71d99"`
Dec 02 13:34:36.839 INFO [stdout] a106f870721ff694445a553390977029d7e5fbbc0817837d1c2312eeaaf71d99
